07-01 16:53:22 D/ModuleListener: ModuleListener.testStarted(com.google.android.assistant.v4.tvts.TvtsAssistant4Test#testNormalDeviceUsage) on SKBFXYZO0DTQ
07-01 16:53:22 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:22 D/DeviceHostUtils: apiLevel: 29
07-01 16:53:22 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:22 D/DeviceHostUtils: apiLevel: 29
07-01 16:53:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:53:22 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
07-01 16:53:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
07-01 16:53:22 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:22 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:23 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:23 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:23 D/RunUtil: Running command null without timeout.
07-01 16:53:23 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
07-01 16:53:23 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
07-01 16:53:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
07-01 16:53:23 I/AppRunnerUtils: Service PID = 29177
07-01 16:53:23 I/AssistantTestUtils: Waiting for assistant to be ready...
07-01 16:53:23 D/RunUtil: Running command [launch Assistant intent, then check if Assistant is in foreground] without timeout.
07-01 16:53:23 D/AssistantTestUtils: Launching Assistant intent
07-01 16:53:23 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
07-01 16:53:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
07-01 16:53:23 D/RunUtil: Running command [check if Assistant is in foreground] without timeout.
07-01 16:53:23 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:53:23 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:53:23 I/DeviceStateMonitor: Waiting 359882 ms for device SKBFXYZO0DTQ package manager
07-01 16:53:24 I/NativeDeviceStateMonitor: Waiting 359666 ms for device SKBFXYZO0DTQ external store
07-01 16:53:26 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:53:31 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
07-01 16:53:31 D/AssistantTestUtils: Supplier "check if Assistant is in foreground" returned: true
07-01 16:53:31 D/AssistantTestUtils: Supplier "launch Assistant intent, then check if Assistant is in foreground" returned: true
07-01 16:53:31 D/AssistantTestUtils: Assistant successfully launched: true
07-01 16:53:31 D/RunUtil: Running command [logcat -d | grep -E 'AssistantWrapper(Impl)?: Assistant is now running.'] without timeout.
07-01 16:53:32 I/AssistantTestUtils: Assistant ready: true
07-01 16:53:32 D/RunUtil: Running command [launch Assistant intent, then check if Assistant is in foreground] without timeout.
07-01 16:53:32 D/AssistantTestUtils: Launching Assistant intent
07-01 16:53:32 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
07-01 16:53:32 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
07-01 16:53:32 D/RunUtil: Running command [check if Assistant is in foreground] without timeout.
07-01 16:53:32 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:53:32 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:53:33 I/DeviceStateMonitor: Waiting 359904 ms for device SKBFXYZO0DTQ package manager
07-01 16:53:33 I/NativeDeviceStateMonitor: Waiting 359777 ms for device SKBFXYZO0DTQ external store
07-01 16:53:34 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:53:35 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
07-01 16:53:35 D/AssistantTestUtils: Supplier "check if Assistant is in foreground" returned: true
07-01 16:53:35 D/AssistantTestUtils: Supplier "launch Assistant intent, then check if Assistant is in foreground" returned: true
07-01 16:53:35 D/AssistantTestUtils: Assistant successfully launched: true
07-01 16:53:35 D/RunUtil: Running command [check if metrics file pattern is present] without timeout.
07-01 16:53:35 I/AssistantTestUtils: Checking if file pattern is present: /storage/emulated/0/Download/katniss-primes*
07-01 16:53:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, ls, -t, -d, /storage/emulated/0/Download/katniss-primes*] with timeout: 2m 0s
07-01 16:53:35 D/AssistantTestUtils: Supplier "check if metrics file pattern is present" returned: true
07-01 16:53:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, ls, -t, -d, /storage/emulated/0/Download/katniss-primes*] with timeout: 2m 0s
07-01 16:53:35 D/AssistantTestUtils: Session-based metrics File: /storage/emulated/0/Download/katniss-primes-2024-07-01T00_53_32.355.txt
07-01 16:53:35 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/Download/katniss-primes-2024-07-01T00_53_32.355.txt)
07-01 16:53:35 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:35 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_13146832536352274238.txt] without timeout.
07-01 16:53:35 D/TvtsBaseAssistantTest: Metrics location: /storage/emulated/0/Download/katniss-primes-2024-07-01T00_53_32.355.txt
07-01 16:53:35 D/TvtsBaseAssistantTest: Option enabling successful!
07-01 16:53:35 I/TvtsBaseAssistantTest: Test: NormalDeviceUsageTest
07-01 16:53:35 I/MeasurementPrinter: search_to_query_latency	Measurement Status	query_to_result_latency	Measurement Status	query_to_result_no_server_latency	Measurement Status
07-01 16:53:35 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:53:35 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 16:53:35 I/DeviceStateMonitor: Waiting 359938 ms for device SKBFXYZO0DTQ package manager
07-01 16:53:35 I/NativeDeviceStateMonitor: Waiting 359846 ms for device SKBFXYZO0DTQ external store
07-01 16:53:36 D/RunUtil: Running command null without timeout.
07-01 16:53:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:53:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:53:37 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:53:37 D/RunUtil: Running command null without timeout.
07-01 16:53:37 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:53:37 I/KeyCodeUtils: Sending keyevent KEYCODE_HOME - intValue: 3
07-01 16:53:39 D/AppRunnerUtils: [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:53:39 D/RunUtil: operation failed, waiting for 500 ms
07-01 16:53:39 D/RunUtil: Running command null without timeout.
07-01 16:53:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:40 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:53:40 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:53:40 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:40 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_8267840484652860253.txt] without timeout.
07-01 16:53:40 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:53:40 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:53:40 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:53:40 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:53:50 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:50 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:53:50 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3264.000000
07-01 16:53:50 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 838.000000
07-01 16:53:50 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 7.000000
07-01 16:53:50 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 316.000000
07-01 16:53:50 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 80.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 7.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:53:50 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 267.000000
07-01 16:53:50 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2495.000000
07-01 16:53:50 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 35.000000
07-01 16:53:50 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:53:50 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2419.000000
07-01 16:53:50 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 844.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 25.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:53:50 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 16:53:50 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 839.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 46.000000
07-01 16:53:50 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 89.000000
07-01 16:53:50 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:53:50 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 13.000000
07-01 16:53:50 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:50 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:53:50 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3264.000000
07-01 16:53:50 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 838.000000
07-01 16:53:50 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 7.000000
07-01 16:53:50 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 316.000000
07-01 16:53:50 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 80.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 7.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:53:50 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 267.000000
07-01 16:53:50 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2495.000000
07-01 16:53:50 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 35.000000
07-01 16:53:50 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:53:50 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2419.000000
07-01 16:53:50 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:53:50 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 844.000000
07-01 16:53:50 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 25.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:53:50 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 16:53:50 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 839.000000
07-01 16:53:50 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 46.000000
07-01 16:53:50 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 89.000000
07-01 16:53:50 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 13.000000
07-01 16:53:50 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:51 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:53:51 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3264.000000
07-01 16:53:51 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 838.000000
07-01 16:53:51 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 7.000000
07-01 16:53:51 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 316.000000
07-01 16:53:51 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 80.000000
07-01 16:53:51 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 7.000000
07-01 16:53:51 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:53:51 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 267.000000
07-01 16:53:51 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2495.000000
07-01 16:53:51 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 35.000000
07-01 16:53:51 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:53:51 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2419.000000
07-01 16:53:51 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 844.000000
07-01 16:53:51 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:53:51 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 25.000000
07-01 16:53:51 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:53:51 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 16:53:51 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 839.000000
07-01 16:53:51 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 46.000000
07-01 16:53:51 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 89.000000
07-01 16:53:51 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 13.000000
07-01 16:53:51 I/MeasurementPrinter:                    89.0	                OK	                 3263.0	                OK	                            844.0	                OK
07-01 16:53:51 D/RunUtil: Running command null without timeout.
07-01 16:53:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:51 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:53:51 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:53:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:52 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:53:52 D/RunUtil: Running command null without timeout.
07-01 16:53:52 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:53:52 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:53:52 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:53:52 D/RunUtil: Running command null without timeout.
07-01 16:53:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:52 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:53:52 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 16:53:52 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:53:52 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYZO0DTQ external store
07-01 16:53:53 D/RunUtil: Running command null without timeout.
07-01 16:53:53 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:53:54 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:53:54 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:53:54 D/RunUtil: Running command null without timeout.
07-01 16:53:54 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:54 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:54 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:53:54 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:53:54 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:53:54 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:53:54 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_3114027243911618892.txt] without timeout.
07-01 16:53:55 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:53:55 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:53:55 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:53:55 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:54:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:05 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:54:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3128.000000
07-01 16:54:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 745.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 14.000000
07-01 16:54:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 324.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 55.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 209.000000
07-01 16:54:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2426.000000
07-01 16:54:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 16:54:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 111.000000
07-01 16:54:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2377.000000
07-01 16:54:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 750.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 18.000000
07-01 16:54:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 16:54:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 746.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 49.000000
07-01 16:54:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 112.000000
07-01 16:54:05 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:54:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 20.000000
07-01 16:54:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:05 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:54:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3128.000000
07-01 16:54:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 745.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 14.000000
07-01 16:54:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 324.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 55.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 209.000000
07-01 16:54:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2426.000000
07-01 16:54:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 16:54:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 111.000000
07-01 16:54:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2377.000000
07-01 16:54:05 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:54:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 750.000000
07-01 16:54:05 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 18.000000
07-01 16:54:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 16:54:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 746.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 49.000000
07-01 16:54:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 112.000000
07-01 16:54:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 20.000000
07-01 16:54:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:05 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:54:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3128.000000
07-01 16:54:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 745.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 14.000000
07-01 16:54:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 324.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 55.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 16:54:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 209.000000
07-01 16:54:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2426.000000
07-01 16:54:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 16:54:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 111.000000
07-01 16:54:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2377.000000
07-01 16:54:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 750.000000
07-01 16:54:05 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 18.000000
07-01 16:54:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 16:54:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 746.000000
07-01 16:54:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 49.000000
07-01 16:54:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 112.000000
07-01 16:54:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 20.000000
07-01 16:54:05 I/MeasurementPrinter:                   112.0	                OK	                 3127.0	                OK	                            750.0	                OK
07-01 16:54:05 D/RunUtil: Running command null without timeout.
07-01 16:54:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:06 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:06 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:06 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:06 D/RunUtil: Running command null without timeout.
07-01 16:54:07 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:07 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:07 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:07 D/RunUtil: Running command null without timeout.
07-01 16:54:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:07 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:54:07 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:54:07 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:54:07 I/NativeDeviceStateMonitor: Waiting 359901 ms for device SKBFXYZO0DTQ external store
07-01 16:54:08 D/RunUtil: Running command null without timeout.
07-01 16:54:08 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:08 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:08 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:08 D/RunUtil: Running command null without timeout.
07-01 16:54:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:09 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:54:09 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:09 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_3526784109619070853.txt] without timeout.
07-01 16:54:09 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:54:09 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:54:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:54:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:54:20 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:20 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:54:20 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3261.000000
07-01 16:54:20 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 773.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 8.000000
07-01 16:54:20 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 322.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 96.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 180.000000
07-01 16:54:20 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2538.000000
07-01 16:54:20 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 16:54:20 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 67.000000
07-01 16:54:20 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2472.000000
07-01 16:54:20 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 775.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 16:54:20 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 24.000000
07-01 16:54:20 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 775.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 40.000000
07-01 16:54:20 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 67.000000
07-01 16:54:20 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:54:20 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:54:20 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:20 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:54:20 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3261.000000
07-01 16:54:20 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 773.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 8.000000
07-01 16:54:20 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 322.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 96.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 180.000000
07-01 16:54:20 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2538.000000
07-01 16:54:20 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 16:54:20 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 67.000000
07-01 16:54:20 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2472.000000
07-01 16:54:20 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:54:20 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 775.000000
07-01 16:54:20 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 16:54:20 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 24.000000
07-01 16:54:20 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 775.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 40.000000
07-01 16:54:20 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 67.000000
07-01 16:54:20 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:54:20 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:20 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:54:20 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3261.000000
07-01 16:54:20 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 773.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 8.000000
07-01 16:54:20 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 322.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 96.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:54:20 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 180.000000
07-01 16:54:20 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2538.000000
07-01 16:54:20 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 16:54:20 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 67.000000
07-01 16:54:20 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2472.000000
07-01 16:54:20 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 775.000000
07-01 16:54:20 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 6.000000
07-01 16:54:20 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 24.000000
07-01 16:54:20 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 775.000000
07-01 16:54:20 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 40.000000
07-01 16:54:20 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 67.000000
07-01 16:54:20 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:54:20 I/MeasurementPrinter:                    67.0	                OK	                 3247.0	                OK	                            775.0	                OK
07-01 16:54:20 D/RunUtil: Running command null without timeout.
07-01 16:54:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:21 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:21 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:21 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:21 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:21 D/RunUtil: Running command null without timeout.
07-01 16:54:21 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:21 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:21 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:21 D/RunUtil: Running command null without timeout.
07-01 16:54:21 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:21 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:21 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:54:21 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:54:22 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:54:22 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:54:23 D/RunUtil: Running command null without timeout.
07-01 16:54:23 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:23 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:23 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:23 D/RunUtil: Running command null without timeout.
07-01 16:54:23 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:23 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:23 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:24 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:24 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:54:24 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:24 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_12798512840293991428.txt] without timeout.
07-01 16:54:24 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:54:24 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:54:24 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:54:24 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:54:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:34 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:54:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3137.000000
07-01 16:54:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 742.000000
07-01 16:54:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 17.000000
07-01 16:54:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 355.000000
07-01 16:54:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:54:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 248.000000
07-01 16:54:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2451.000000
07-01 16:54:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 25.000000
07-01 16:54:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 80.000000
07-01 16:54:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2387.000000
07-01 16:54:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 748.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 16.000000
07-01 16:54:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 16:54:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 744.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 50.000000
07-01 16:54:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 80.000000
07-01 16:54:34 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:54:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 35.000000
07-01 16:54:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:34 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:54:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3137.000000
07-01 16:54:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 742.000000
07-01 16:54:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 17.000000
07-01 16:54:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 355.000000
07-01 16:54:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:54:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 248.000000
07-01 16:54:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2451.000000
07-01 16:54:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 25.000000
07-01 16:54:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 80.000000
07-01 16:54:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2387.000000
07-01 16:54:34 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:54:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 748.000000
07-01 16:54:34 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 16.000000
07-01 16:54:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 16:54:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 744.000000
07-01 16:54:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 50.000000
07-01 16:54:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 80.000000
07-01 16:54:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 35.000000
07-01 16:54:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:35 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:54:35 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3137.000000
07-01 16:54:35 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 742.000000
07-01 16:54:35 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 17.000000
07-01 16:54:35 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 355.000000
07-01 16:54:35 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 16:54:35 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 16:54:35 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:54:35 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 248.000000
07-01 16:54:35 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2451.000000
07-01 16:54:35 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 25.000000
07-01 16:54:35 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 80.000000
07-01 16:54:35 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2387.000000
07-01 16:54:35 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 748.000000
07-01 16:54:35 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:35 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 16:54:35 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 16.000000
07-01 16:54:35 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 16:54:35 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 744.000000
07-01 16:54:35 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 50.000000
07-01 16:54:35 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 80.000000
07-01 16:54:35 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 35.000000
07-01 16:54:35 I/MeasurementPrinter:                    80.0	                OK	                 3135.0	                OK	                            748.0	                OK
07-01 16:54:35 D/RunUtil: Running command null without timeout.
07-01 16:54:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:35 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:35 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:36 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:36 D/RunUtil: Running command null without timeout.
07-01 16:54:36 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:36 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:36 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:36 D/RunUtil: Running command null without timeout.
07-01 16:54:36 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:36 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:36 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:54:36 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:54:36 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:54:36 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYZO0DTQ external store
07-01 16:54:37 D/RunUtil: Running command null without timeout.
07-01 16:54:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:37 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:37 D/RunUtil: Running command null without timeout.
07-01 16:54:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:38 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:54:38 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:38 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_5350046514901359360.txt] without timeout.
07-01 16:54:39 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:54:39 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:54:39 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:54:39 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:54:49 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:49 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:54:49 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3178.000000
07-01 16:54:49 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 744.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 18.000000
07-01 16:54:49 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 59.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 11.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 154.000000
07-01 16:54:49 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2480.000000
07-01 16:54:49 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 10.000000
07-01 16:54:49 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 87.000000
07-01 16:54:49 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2422.000000
07-01 16:54:49 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 746.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 5.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:54:49 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 16:54:49 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 745.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 64.000000
07-01 16:54:49 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 86.000000
07-01 16:54:49 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:54:49 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 16.000000
07-01 16:54:49 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:49 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:54:49 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3178.000000
07-01 16:54:49 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 744.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 18.000000
07-01 16:54:49 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 59.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 11.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 154.000000
07-01 16:54:49 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2480.000000
07-01 16:54:49 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 10.000000
07-01 16:54:49 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 87.000000
07-01 16:54:49 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2422.000000
07-01 16:54:49 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:54:49 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 746.000000
07-01 16:54:49 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 5.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:54:49 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 16:54:49 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 745.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 64.000000
07-01 16:54:49 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 86.000000
07-01 16:54:49 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 16.000000
07-01 16:54:49 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:49 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:54:49 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3178.000000
07-01 16:54:49 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 744.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 18.000000
07-01 16:54:49 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 59.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 11.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:54:49 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 154.000000
07-01 16:54:49 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2480.000000
07-01 16:54:49 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 10.000000
07-01 16:54:49 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 87.000000
07-01 16:54:49 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2422.000000
07-01 16:54:49 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 746.000000
07-01 16:54:49 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 5.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:54:49 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 16:54:49 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 745.000000
07-01 16:54:49 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 64.000000
07-01 16:54:49 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 86.000000
07-01 16:54:49 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 16.000000
07-01 16:54:49 I/MeasurementPrinter:                    86.0	                OK	                 3168.0	                OK	                            746.0	                OK
07-01 16:54:49 D/RunUtil: Running command null without timeout.
07-01 16:54:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:50 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:50 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:50 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:51 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:51 D/RunUtil: Running command null without timeout.
07-01 16:54:51 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:51 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:51 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:51 D/RunUtil: Running command null without timeout.
07-01 16:54:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:51 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:54:51 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:54:51 I/DeviceStateMonitor: Waiting 359963 ms for device SKBFXYZO0DTQ package manager
07-01 16:54:51 I/NativeDeviceStateMonitor: Waiting 359901 ms for device SKBFXYZO0DTQ external store
07-01 16:54:52 D/RunUtil: Running command null without timeout.
07-01 16:54:52 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:52 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:54:52 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:54:52 D/RunUtil: Running command null without timeout.
07-01 16:54:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:54:53 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:54:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:54:53 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:54:53 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_11136528815547452578.txt] without timeout.
07-01 16:54:53 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:54:53 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:54:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:54:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:55:04 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:04 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:55:04 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3119.000000
07-01 16:55:04 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 733.000000
07-01 16:55:04 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 359.000000
07-01 16:55:04 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 65.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 7.000000
07-01 16:55:04 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 176.000000
07-01 16:55:04 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2429.000000
07-01 16:55:04 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 20.000000
07-01 16:55:04 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 110.000000
07-01 16:55:04 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2382.000000
07-01 16:55:04 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 734.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 12.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:55:04 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 15.000000
07-01 16:55:04 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 734.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 80.000000
07-01 16:55:04 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 110.000000
07-01 16:55:04 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:55:04 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 12.000000
07-01 16:55:04 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:04 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:55:04 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3119.000000
07-01 16:55:04 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 733.000000
07-01 16:55:04 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 359.000000
07-01 16:55:04 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 65.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 7.000000
07-01 16:55:04 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 176.000000
07-01 16:55:04 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2429.000000
07-01 16:55:04 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 20.000000
07-01 16:55:04 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 110.000000
07-01 16:55:04 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2382.000000
07-01 16:55:04 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:55:04 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 734.000000
07-01 16:55:04 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 12.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:55:04 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 15.000000
07-01 16:55:04 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 734.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 80.000000
07-01 16:55:04 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 110.000000
07-01 16:55:04 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 12.000000
07-01 16:55:04 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:04 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:55:04 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3119.000000
07-01 16:55:04 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 733.000000
07-01 16:55:04 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 359.000000
07-01 16:55:04 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 65.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 7.000000
07-01 16:55:04 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 176.000000
07-01 16:55:04 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2429.000000
07-01 16:55:04 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 20.000000
07-01 16:55:04 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 110.000000
07-01 16:55:04 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2382.000000
07-01 16:55:04 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 734.000000
07-01 16:55:04 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 12.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:55:04 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 15.000000
07-01 16:55:04 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 734.000000
07-01 16:55:04 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 80.000000
07-01 16:55:04 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 110.000000
07-01 16:55:04 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 12.000000
07-01 16:55:04 I/MeasurementPrinter:                   110.0	                OK	                 3116.0	                OK	                            734.0	                OK
07-01 16:55:04 D/RunUtil: Running command null without timeout.
07-01 16:55:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:05 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:05 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:05 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:05 D/RunUtil: Running command null without timeout.
07-01 16:55:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:06 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:06 D/RunUtil: Running command null without timeout.
07-01 16:55:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:06 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:55:06 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 16:55:06 I/DeviceStateMonitor: Waiting 359963 ms for device SKBFXYZO0DTQ package manager
07-01 16:55:06 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYZO0DTQ external store
07-01 16:55:07 D/RunUtil: Running command null without timeout.
07-01 16:55:07 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:07 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:07 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:07 D/RunUtil: Running command null without timeout.
07-01 16:55:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:08 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:08 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:55:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:08 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_2336140905843444532.txt] without timeout.
07-01 16:55:08 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:55:08 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:55:08 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:55:08 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:55:18 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:19 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:55:19 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3202.000000
07-01 16:55:19 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 777.000000
07-01 16:55:19 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 315.000000
07-01 16:55:19 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 28.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 9.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:19 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 191.000000
07-01 16:55:19 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2537.000000
07-01 16:55:19 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:55:19 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 94.000000
07-01 16:55:19 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2419.000000
07-01 16:55:19 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 778.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:55:19 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:55:19 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 778.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 56.000000
07-01 16:55:19 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 94.000000
07-01 16:55:19 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:55:19 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:55:19 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:19 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:55:19 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3202.000000
07-01 16:55:19 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 777.000000
07-01 16:55:19 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 315.000000
07-01 16:55:19 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 28.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 9.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:19 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 191.000000
07-01 16:55:19 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2537.000000
07-01 16:55:19 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:55:19 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 94.000000
07-01 16:55:19 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2419.000000
07-01 16:55:19 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:55:19 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 778.000000
07-01 16:55:19 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:55:19 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:55:19 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 778.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 56.000000
07-01 16:55:19 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 94.000000
07-01 16:55:19 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:55:19 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:19 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:55:19 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3202.000000
07-01 16:55:19 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 777.000000
07-01 16:55:19 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 315.000000
07-01 16:55:19 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 28.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 9.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:19 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 191.000000
07-01 16:55:19 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2537.000000
07-01 16:55:19 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:55:19 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 94.000000
07-01 16:55:19 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2419.000000
07-01 16:55:19 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 778.000000
07-01 16:55:19 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 17.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:55:19 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:55:19 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 778.000000
07-01 16:55:19 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 56.000000
07-01 16:55:19 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 94.000000
07-01 16:55:19 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:55:19 I/MeasurementPrinter:                    94.0	                OK	                 3197.0	                OK	                            778.0	                OK
07-01 16:55:19 D/RunUtil: Running command null without timeout.
07-01 16:55:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:19 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:19 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:20 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:20 D/RunUtil: Running command null without timeout.
07-01 16:55:20 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:20 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:20 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:20 D/RunUtil: Running command null without timeout.
07-01 16:55:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:20 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:55:20 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:55:20 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:55:20 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:55:22 D/RunUtil: Running command null without timeout.
07-01 16:55:22 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:22 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:22 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:22 D/RunUtil: Running command null without timeout.
07-01 16:55:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:22 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:55:23 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:23 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_680200083575062100.txt] without timeout.
07-01 16:55:23 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:55:23 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:55:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:55:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:55:33 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:33 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:55:33 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3265.000000
07-01 16:55:33 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 717.000000
07-01 16:55:33 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:55:33 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 298.000000
07-01 16:55:33 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 60.000000
07-01 16:55:33 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:33 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:55:33 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 140.000000
07-01 16:55:33 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2659.000000
07-01 16:55:33 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 36.000000
07-01 16:55:33 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:55:33 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2535.000000
07-01 16:55:33 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 718.000000
07-01 16:55:33 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 12.000000
07-01 16:55:33 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 24.000000
07-01 16:55:33 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 16:55:33 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 720.000000
07-01 16:55:33 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 48.000000
07-01 16:55:33 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:55:33 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:55:33 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:55:33 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:34 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:55:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3265.000000
07-01 16:55:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 717.000000
07-01 16:55:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:55:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 298.000000
07-01 16:55:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 60.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:55:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 140.000000
07-01 16:55:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2659.000000
07-01 16:55:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 36.000000
07-01 16:55:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:55:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2535.000000
07-01 16:55:34 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:55:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 718.000000
07-01 16:55:34 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 12.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 24.000000
07-01 16:55:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 16:55:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 720.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 48.000000
07-01 16:55:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:55:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:55:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:34 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:55:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3265.000000
07-01 16:55:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 717.000000
07-01 16:55:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:55:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 298.000000
07-01 16:55:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 60.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:55:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 140.000000
07-01 16:55:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2659.000000
07-01 16:55:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 36.000000
07-01 16:55:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:55:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2535.000000
07-01 16:55:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 718.000000
07-01 16:55:34 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 12.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 24.000000
07-01 16:55:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 16:55:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 720.000000
07-01 16:55:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 48.000000
07-01 16:55:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:55:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:55:34 I/MeasurementPrinter:                    91.0	                OK	                 3253.0	                OK	                            718.0	                OK
07-01 16:55:34 D/RunUtil: Running command null without timeout.
07-01 16:55:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:34 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:34 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:35 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:35 D/RunUtil: Running command null without timeout.
07-01 16:55:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:35 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:35 D/RunUtil: Running command null without timeout.
07-01 16:55:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:35 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:55:35 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:55:35 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:55:35 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:55:36 D/RunUtil: Running command null without timeout.
07-01 16:55:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:37 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:37 D/RunUtil: Running command null without timeout.
07-01 16:55:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:37 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:55:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:38 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_2394893305700506710.txt] without timeout.
07-01 16:55:38 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:55:38 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:55:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:55:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:55:48 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:48 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:55:48 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3277.000000
07-01 16:55:48 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 741.000000
07-01 16:55:48 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 16:55:48 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 292.000000
07-01 16:55:48 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 24.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 9.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:48 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 133.000000
07-01 16:55:48 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2578.000000
07-01 16:55:48 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 25.000000
07-01 16:55:48 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 84.000000
07-01 16:55:48 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2528.000000
07-01 16:55:48 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 743.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 21.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:55:48 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:55:48 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 743.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 48.000000
07-01 16:55:48 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 85.000000
07-01 16:55:48 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:55:48 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:55:48 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:48 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:55:48 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3277.000000
07-01 16:55:48 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 741.000000
07-01 16:55:48 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 16:55:48 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 292.000000
07-01 16:55:48 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 24.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 9.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:48 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 133.000000
07-01 16:55:48 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2578.000000
07-01 16:55:48 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 25.000000
07-01 16:55:48 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 84.000000
07-01 16:55:48 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2528.000000
07-01 16:55:48 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:55:48 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 743.000000
07-01 16:55:48 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 21.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:55:48 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:55:48 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 743.000000
07-01 16:55:48 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 48.000000
07-01 16:55:48 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 85.000000
07-01 16:55:48 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:55:48 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:49 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:55:49 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3277.000000
07-01 16:55:49 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 741.000000
07-01 16:55:49 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 16:55:49 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 292.000000
07-01 16:55:49 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 24.000000
07-01 16:55:49 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 9.000000
07-01 16:55:49 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:55:49 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 133.000000
07-01 16:55:49 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2578.000000
07-01 16:55:49 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 25.000000
07-01 16:55:49 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 84.000000
07-01 16:55:49 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2528.000000
07-01 16:55:49 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 743.000000
07-01 16:55:49 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:55:49 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 21.000000
07-01 16:55:49 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:55:49 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:55:49 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 743.000000
07-01 16:55:49 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 48.000000
07-01 16:55:49 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 85.000000
07-01 16:55:49 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 16:55:49 I/MeasurementPrinter:                    85.0	                OK	                 3271.0	                OK	                            743.0	                OK
07-01 16:55:49 D/RunUtil: Running command null without timeout.
07-01 16:55:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:49 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:49 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:50 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:50 D/RunUtil: Running command null without timeout.
07-01 16:55:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:50 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:50 D/RunUtil: Running command null without timeout.
07-01 16:55:50 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:50 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:50 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:55:50 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:55:50 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:55:50 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:55:51 D/RunUtil: Running command null without timeout.
07-01 16:55:51 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:51 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:55:51 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:55:51 D/RunUtil: Running command null without timeout.
07-01 16:55:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:55:52 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:55:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:55:52 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:55:52 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_2929741747176668574.txt] without timeout.
07-01 16:55:53 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:55:53 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:55:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:55:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:56:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:03 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:56:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3273.000000
07-01 16:56:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 716.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 5.000000
07-01 16:56:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 61.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 16.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 182.000000
07-01 16:56:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2592.000000
07-01 16:56:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 22.000000
07-01 16:56:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 101.000000
07-01 16:56:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2551.000000
07-01 16:56:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 717.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:56:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 16:56:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 717.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 63.000000
07-01 16:56:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 103.000000
07-01 16:56:03 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:56:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 12.000000
07-01 16:56:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:03 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:56:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3273.000000
07-01 16:56:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 716.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 5.000000
07-01 16:56:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 61.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 16.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 182.000000
07-01 16:56:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2592.000000
07-01 16:56:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 22.000000
07-01 16:56:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 101.000000
07-01 16:56:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2551.000000
07-01 16:56:03 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:56:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 717.000000
07-01 16:56:03 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:56:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 16:56:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 717.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 63.000000
07-01 16:56:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 103.000000
07-01 16:56:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 12.000000
07-01 16:56:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:03 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:56:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3273.000000
07-01 16:56:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 716.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 5.000000
07-01 16:56:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 61.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 16.000000
07-01 16:56:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 182.000000
07-01 16:56:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2592.000000
07-01 16:56:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 22.000000
07-01 16:56:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 101.000000
07-01 16:56:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2551.000000
07-01 16:56:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 717.000000
07-01 16:56:03 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 10.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 16:56:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 16:56:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 717.000000
07-01 16:56:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 63.000000
07-01 16:56:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 103.000000
07-01 16:56:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 12.000000
07-01 16:56:03 I/MeasurementPrinter:                   103.0	                OK	                 3268.0	                OK	                            717.0	                OK
07-01 16:56:03 D/RunUtil: Running command null without timeout.
07-01 16:56:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:04 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:05 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:05 D/RunUtil: Running command null without timeout.
07-01 16:56:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:05 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:05 D/RunUtil: Running command null without timeout.
07-01 16:56:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:05 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:56:05 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 16:56:05 I/DeviceStateMonitor: Waiting 359963 ms for device SKBFXYZO0DTQ package manager
07-01 16:56:05 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYZO0DTQ external store
07-01 16:56:06 D/RunUtil: Running command null without timeout.
07-01 16:56:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:06 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:06 D/RunUtil: Running command null without timeout.
07-01 16:56:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:07 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:56:07 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:07 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_11729569767865379891.txt] without timeout.
07-01 16:56:07 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:56:07 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:56:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:56:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:56:18 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:19 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:56:19 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 4041.000000
07-01 16:56:19 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 709.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:56:19 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 307.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 56.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 6.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 159.000000
07-01 16:56:19 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3367.000000
07-01 16:56:19 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 30.000000
07-01 16:56:19 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 92.000000
07-01 16:56:19 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3316.000000
07-01 16:56:19 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 713.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 20.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 10.000000
07-01 16:56:19 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 5.000000
07-01 16:56:19 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 709.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 52.000000
07-01 16:56:19 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 92.000000
07-01 16:56:19 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:56:19 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:56:19 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:19 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:56:19 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 4041.000000
07-01 16:56:19 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 709.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:56:19 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 307.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 56.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 6.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 159.000000
07-01 16:56:19 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3367.000000
07-01 16:56:19 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 30.000000
07-01 16:56:19 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 92.000000
07-01 16:56:19 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3316.000000
07-01 16:56:19 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:56:19 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 713.000000
07-01 16:56:19 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 20.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 10.000000
07-01 16:56:19 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 5.000000
07-01 16:56:19 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 709.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 52.000000
07-01 16:56:19 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 92.000000
07-01 16:56:19 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:56:19 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:19 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:56:19 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 4041.000000
07-01 16:56:19 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 709.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 12.000000
07-01 16:56:19 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 307.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 56.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 4.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 6.000000
07-01 16:56:19 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 159.000000
07-01 16:56:19 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3367.000000
07-01 16:56:19 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 30.000000
07-01 16:56:19 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 92.000000
07-01 16:56:19 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3316.000000
07-01 16:56:19 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 713.000000
07-01 16:56:19 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 20.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 10.000000
07-01 16:56:19 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 5.000000
07-01 16:56:19 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 709.000000
07-01 16:56:19 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 52.000000
07-01 16:56:19 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 92.000000
07-01 16:56:19 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:56:19 I/MeasurementPrinter:                    92.0	                OK	                 4029.0	                OK	                            713.0	                OK
07-01 16:56:19 D/RunUtil: Running command null without timeout.
07-01 16:56:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:20 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:20 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:20 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:20 D/RunUtil: Running command null without timeout.
07-01 16:56:20 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:20 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:20 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:20 D/RunUtil: Running command null without timeout.
07-01 16:56:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:20 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:56:20 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:56:21 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:56:21 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYZO0DTQ external store
07-01 16:56:22 D/RunUtil: Running command null without timeout.
07-01 16:56:22 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:22 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:22 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:22 D/RunUtil: Running command null without timeout.
07-01 16:56:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:23 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:56:23 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:23 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_2049449820396906112.txt] without timeout.
07-01 16:56:23 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:56:23 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:56:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:56:23 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:56:33 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:33 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:56:33 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3139.000000
07-01 16:56:33 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 730.000000
07-01 16:56:33 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 9.000000
07-01 16:56:33 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 325.000000
07-01 16:56:33 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 7.000000
07-01 16:56:33 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:56:33 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:33 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 213.000000
07-01 16:56:33 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2469.000000
07-01 16:56:33 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 20.000000
07-01 16:56:33 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:56:33 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2402.000000
07-01 16:56:33 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 742.000000
07-01 16:56:33 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 13.000000
07-01 16:56:33 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:56:33 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 16:56:33 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 731.000000
07-01 16:56:33 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 59.000000
07-01 16:56:33 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:56:33 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:56:33 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 16:56:33 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:34 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:56:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3139.000000
07-01 16:56:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 730.000000
07-01 16:56:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 9.000000
07-01 16:56:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 325.000000
07-01 16:56:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 7.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 213.000000
07-01 16:56:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2469.000000
07-01 16:56:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 20.000000
07-01 16:56:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:56:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2402.000000
07-01 16:56:34 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:56:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 742.000000
07-01 16:56:34 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 13.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:56:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 16:56:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 731.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 59.000000
07-01 16:56:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:56:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 16:56:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:34 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:56:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3139.000000
07-01 16:56:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 730.000000
07-01 16:56:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 9.000000
07-01 16:56:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 325.000000
07-01 16:56:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 7.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 213.000000
07-01 16:56:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2469.000000
07-01 16:56:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 20.000000
07-01 16:56:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 90.000000
07-01 16:56:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2402.000000
07-01 16:56:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 742.000000
07-01 16:56:34 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 13.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:56:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 16:56:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 731.000000
07-01 16:56:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 59.000000
07-01 16:56:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:56:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 16:56:34 I/MeasurementPrinter:                    91.0	                OK	                 3144.0	                OK	                            742.0	                OK
07-01 16:56:34 D/RunUtil: Running command null without timeout.
07-01 16:56:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:34 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:34 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:35 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:35 D/RunUtil: Running command null without timeout.
07-01 16:56:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:35 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:35 D/RunUtil: Running command null without timeout.
07-01 16:56:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:35 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:56:35 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:56:35 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:56:36 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:56:37 D/RunUtil: Running command null without timeout.
07-01 16:56:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:37 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:37 D/RunUtil: Running command null without timeout.
07-01 16:56:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:37 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:56:38 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:38 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_16140308974804444139.txt] without timeout.
07-01 16:56:38 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:56:38 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:56:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:56:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:56:48 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:48 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:56:48 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3161.000000
07-01 16:56:48 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 737.000000
07-01 16:56:48 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 18.000000
07-01 16:56:48 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 342.000000
07-01 16:56:48 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 23.000000
07-01 16:56:48 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:56:48 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:48 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 156.000000
07-01 16:56:48 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2470.000000
07-01 16:56:48 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 19.000000
07-01 16:56:48 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 63.000000
07-01 16:56:48 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2418.000000
07-01 16:56:48 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 739.000000
07-01 16:56:48 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 5.000000
07-01 16:56:48 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 14.000000
07-01 16:56:48 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 16:56:48 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 739.000000
07-01 16:56:48 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 38.000000
07-01 16:56:48 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 65.000000
07-01 16:56:48 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:56:48 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 27.000000
07-01 16:56:48 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:49 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:56:49 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3161.000000
07-01 16:56:49 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 737.000000
07-01 16:56:49 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 18.000000
07-01 16:56:49 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 342.000000
07-01 16:56:49 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 23.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:49 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 156.000000
07-01 16:56:49 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2470.000000
07-01 16:56:49 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 19.000000
07-01 16:56:49 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 63.000000
07-01 16:56:49 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2418.000000
07-01 16:56:49 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:56:49 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 739.000000
07-01 16:56:49 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 5.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 14.000000
07-01 16:56:49 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 16:56:49 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 739.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 38.000000
07-01 16:56:49 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 65.000000
07-01 16:56:49 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 27.000000
07-01 16:56:49 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:49 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:56:49 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3161.000000
07-01 16:56:49 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 737.000000
07-01 16:56:49 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 18.000000
07-01 16:56:49 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 342.000000
07-01 16:56:49 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 23.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:56:49 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 156.000000
07-01 16:56:49 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2470.000000
07-01 16:56:49 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 19.000000
07-01 16:56:49 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 63.000000
07-01 16:56:49 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2418.000000
07-01 16:56:49 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 739.000000
07-01 16:56:49 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 5.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 14.000000
07-01 16:56:49 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 16:56:49 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 739.000000
07-01 16:56:49 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 38.000000
07-01 16:56:49 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 65.000000
07-01 16:56:49 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 27.000000
07-01 16:56:49 I/MeasurementPrinter:                    65.0	                OK	                 3157.0	                OK	                            739.0	                OK
07-01 16:56:49 D/RunUtil: Running command null without timeout.
07-01 16:56:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:49 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:49 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:50 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:50 D/RunUtil: Running command null without timeout.
07-01 16:56:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:50 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:50 D/RunUtil: Running command null without timeout.
07-01 16:56:50 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:50 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:50 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:56:50 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:56:50 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:56:50 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:56:51 D/RunUtil: Running command null without timeout.
07-01 16:56:52 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:52 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:56:52 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:56:52 D/RunUtil: Running command null without timeout.
07-01 16:56:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:56:52 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:56:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:56:52 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:56:53 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_14982664127703881839.txt] without timeout.
07-01 16:56:53 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:56:53 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:56:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:56:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:57:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:03 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:57:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3059.000000
07-01 16:57:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 731.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 11.000000
07-01 16:57:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 49.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 238.000000
07-01 16:57:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2380.000000
07-01 16:57:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 15.000000
07-01 16:57:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 93.000000
07-01 16:57:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2323.000000
07-01 16:57:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 736.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 9.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:57:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 16:57:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 731.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 66.000000
07-01 16:57:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 93.000000
07-01 16:57:03 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:57:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 21.000000
07-01 16:57:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:03 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:57:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3059.000000
07-01 16:57:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 731.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 11.000000
07-01 16:57:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 49.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 238.000000
07-01 16:57:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2380.000000
07-01 16:57:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 15.000000
07-01 16:57:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 93.000000
07-01 16:57:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2323.000000
07-01 16:57:03 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:57:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 736.000000
07-01 16:57:03 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 9.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:57:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 16:57:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 731.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 66.000000
07-01 16:57:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 93.000000
07-01 16:57:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 21.000000
07-01 16:57:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:03 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:57:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3059.000000
07-01 16:57:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 731.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 11.000000
07-01 16:57:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 345.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 49.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:57:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 238.000000
07-01 16:57:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2380.000000
07-01 16:57:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 15.000000
07-01 16:57:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 93.000000
07-01 16:57:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2323.000000
07-01 16:57:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 736.000000
07-01 16:57:03 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 9.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 16:57:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 16:57:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 731.000000
07-01 16:57:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 66.000000
07-01 16:57:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 93.000000
07-01 16:57:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 21.000000
07-01 16:57:03 I/MeasurementPrinter:                    93.0	                OK	                 3059.0	                OK	                            736.0	                OK
07-01 16:57:03 D/RunUtil: Running command null without timeout.
07-01 16:57:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:04 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:05 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:05 D/RunUtil: Running command null without timeout.
07-01 16:57:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:05 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:05 D/RunUtil: Running command null without timeout.
07-01 16:57:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:05 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:57:05 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 16:57:05 I/DeviceStateMonitor: Waiting 359963 ms for device SKBFXYZO0DTQ package manager
07-01 16:57:05 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:57:06 D/RunUtil: Running command null without timeout.
07-01 16:57:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:06 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:06 D/RunUtil: Running command null without timeout.
07-01 16:57:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:07 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:57:07 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:07 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_2764267367095108641.txt] without timeout.
07-01 16:57:07 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:57:07 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:57:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:57:08 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:57:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:17 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:57:17 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3199.000000
07-01 16:57:17 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 740.000000
07-01 16:57:17 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 344.000000
07-01 16:57:17 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 82.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 13.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:57:17 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 230.000000
07-01 16:57:17 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2535.000000
07-01 16:57:17 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:57:17 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 94.000000
07-01 16:57:17 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2453.000000
07-01 16:57:17 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 747.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 6.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 22.000000
07-01 16:57:17 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 11.000000
07-01 16:57:17 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 742.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 52.000000
07-01 16:57:17 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 95.000000
07-01 16:57:17 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:57:17 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:57:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:17 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:57:17 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3199.000000
07-01 16:57:17 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 740.000000
07-01 16:57:17 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 344.000000
07-01 16:57:17 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 82.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 13.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:57:17 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 230.000000
07-01 16:57:17 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2535.000000
07-01 16:57:17 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:57:17 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 94.000000
07-01 16:57:17 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2453.000000
07-01 16:57:17 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:57:17 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 747.000000
07-01 16:57:17 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 6.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 22.000000
07-01 16:57:17 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 11.000000
07-01 16:57:17 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 742.000000
07-01 16:57:17 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 52.000000
07-01 16:57:17 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 95.000000
07-01 16:57:17 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:57:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:18 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:57:18 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3199.000000
07-01 16:57:18 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 740.000000
07-01 16:57:18 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 344.000000
07-01 16:57:18 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 82.000000
07-01 16:57:18 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 13.000000
07-01 16:57:18 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:57:18 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 230.000000
07-01 16:57:18 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2535.000000
07-01 16:57:18 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 28.000000
07-01 16:57:18 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 94.000000
07-01 16:57:18 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2453.000000
07-01 16:57:18 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 747.000000
07-01 16:57:18 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:18 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 6.000000
07-01 16:57:18 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 22.000000
07-01 16:57:18 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 11.000000
07-01 16:57:18 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 742.000000
07-01 16:57:18 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 52.000000
07-01 16:57:18 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 95.000000
07-01 16:57:18 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 16:57:18 I/MeasurementPrinter:                    95.0	                OK	                 3200.0	                OK	                            747.0	                OK
07-01 16:57:18 D/RunUtil: Running command null without timeout.
07-01 16:57:18 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:18 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:18 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:18 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:19 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:19 D/RunUtil: Running command null without timeout.
07-01 16:57:19 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:19 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:19 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:19 D/RunUtil: Running command null without timeout.
07-01 16:57:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:19 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:57:19 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:57:19 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:57:19 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:57:20 D/RunUtil: Running command null without timeout.
07-01 16:57:21 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:21 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:21 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:21 D/RunUtil: Running command null without timeout.
07-01 16:57:21 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:21 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:21 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:21 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:57:21 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:22 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_13489218904427768760.txt] without timeout.
07-01 16:57:22 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:57:22 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:57:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:57:22 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:57:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:32 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:57:32 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3188.000000
07-01 16:57:32 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 727.000000
07-01 16:57:32 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 11.000000
07-01 16:57:32 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 333.000000
07-01 16:57:32 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 47.000000
07-01 16:57:32 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 227.000000
07-01 16:57:32 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2484.000000
07-01 16:57:32 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 37.000000
07-01 16:57:32 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2444.000000
07-01 16:57:32 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:57:32 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 16:57:32 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 728.000000
07-01 16:57:32 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:57:32 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:57:32 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 16:57:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:32 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:57:32 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3188.000000
07-01 16:57:32 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 727.000000
07-01 16:57:32 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 11.000000
07-01 16:57:32 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 333.000000
07-01 16:57:32 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 47.000000
07-01 16:57:32 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 227.000000
07-01 16:57:32 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2484.000000
07-01 16:57:32 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 37.000000
07-01 16:57:32 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2444.000000
07-01 16:57:32 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:57:32 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:57:32 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:32 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 16:57:32 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 728.000000
07-01 16:57:32 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:57:32 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 16:57:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:33 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:57:33 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3188.000000
07-01 16:57:33 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 727.000000
07-01 16:57:33 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 11.000000
07-01 16:57:33 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 333.000000
07-01 16:57:33 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 47.000000
07-01 16:57:33 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 227.000000
07-01 16:57:33 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2484.000000
07-01 16:57:33 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 37.000000
07-01 16:57:33 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2444.000000
07-01 16:57:33 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:57:33 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:33 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 16:57:33 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 728.000000
07-01 16:57:33 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 91.000000
07-01 16:57:33 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 22.000000
07-01 16:57:33 I/MeasurementPrinter:                    91.0	                OK	                 3174.0	                OK	                            730.0	                OK
07-01 16:57:33 D/RunUtil: Running command null without timeout.
07-01 16:57:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:33 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:33 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:34 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:34 D/RunUtil: Running command null without timeout.
07-01 16:57:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:34 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:34 D/RunUtil: Running command null without timeout.
07-01 16:57:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:34 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:57:34 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:57:34 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:57:34 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:57:35 D/RunUtil: Running command null without timeout.
07-01 16:57:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:35 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:35 D/RunUtil: Running command null without timeout.
07-01 16:57:36 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:36 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:36 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:36 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:36 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:57:36 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:36 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_2653880248591301434.txt] without timeout.
07-01 16:57:37 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:57:37 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:57:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:57:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:57:47 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:47 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:57:47 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3342.000000
07-01 16:57:47 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 725.000000
07-01 16:57:47 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 16:57:47 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 351.000000
07-01 16:57:47 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 60.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:57:47 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 135.000000
07-01 16:57:47 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2641.000000
07-01 16:57:47 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 16:57:47 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 86.000000
07-01 16:57:47 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2606.000000
07-01 16:57:47 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 737.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 8.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 16:57:47 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 3.000000
07-01 16:57:47 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 726.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 56.000000
07-01 16:57:47 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 86.000000
07-01 16:57:47 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:57:47 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 19.000000
07-01 16:57:47 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:47 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:57:47 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3342.000000
07-01 16:57:47 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 725.000000
07-01 16:57:47 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 16:57:47 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 351.000000
07-01 16:57:47 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 60.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:57:47 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 135.000000
07-01 16:57:47 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2641.000000
07-01 16:57:47 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 16:57:47 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 86.000000
07-01 16:57:47 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2606.000000
07-01 16:57:47 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:57:47 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 737.000000
07-01 16:57:47 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 8.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 16:57:47 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 3.000000
07-01 16:57:47 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 726.000000
07-01 16:57:47 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 56.000000
07-01 16:57:47 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 86.000000
07-01 16:57:47 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 19.000000
07-01 16:57:47 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:48 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:57:48 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3342.000000
07-01 16:57:48 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 725.000000
07-01 16:57:48 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 3.000000
07-01 16:57:48 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 351.000000
07-01 16:57:48 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 60.000000
07-01 16:57:48 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 16:57:48 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:57:48 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 135.000000
07-01 16:57:48 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2641.000000
07-01 16:57:48 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 23.000000
07-01 16:57:48 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 86.000000
07-01 16:57:48 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2606.000000
07-01 16:57:48 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 737.000000
07-01 16:57:48 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:57:48 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 8.000000
07-01 16:57:48 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 16:57:48 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 3.000000
07-01 16:57:48 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 726.000000
07-01 16:57:48 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 56.000000
07-01 16:57:48 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 86.000000
07-01 16:57:48 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 19.000000
07-01 16:57:48 I/MeasurementPrinter:                    86.0	                OK	                 3343.0	                OK	                            737.0	                OK
07-01 16:57:48 D/RunUtil: Running command null without timeout.
07-01 16:57:48 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:48 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:48 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:48 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:49 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:49 D/RunUtil: Running command null without timeout.
07-01 16:57:49 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:49 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:49 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:49 D/RunUtil: Running command null without timeout.
07-01 16:57:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:49 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:57:49 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:57:49 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:57:49 I/NativeDeviceStateMonitor: Waiting 359900 ms for device SKBFXYZO0DTQ external store
07-01 16:57:50 D/RunUtil: Running command null without timeout.
07-01 16:57:51 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:51 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:57:51 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:57:51 D/RunUtil: Running command null without timeout.
07-01 16:57:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:51 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:57:51 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:57:51 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:57:51 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:57:52 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_4253801418137304393.txt] without timeout.
07-01 16:57:52 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:57:52 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:57:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:57:52 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:58:02 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:02 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:58:02 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3103.000000
07-01 16:58:02 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 727.000000
07-01 16:58:02 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 10.000000
07-01 16:58:02 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 319.000000
07-01 16:58:02 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 9.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:58:02 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 200.000000
07-01 16:58:02 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2413.000000
07-01 16:58:02 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 27.000000
07-01 16:58:02 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 92.000000
07-01 16:58:02 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2366.000000
07-01 16:58:02 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 9.000000
07-01 16:58:02 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 16:58:02 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 729.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 54.000000
07-01 16:58:02 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 93.000000
07-01 16:58:02 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:58:02 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:58:02 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:02 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:58:02 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3103.000000
07-01 16:58:02 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 727.000000
07-01 16:58:02 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 10.000000
07-01 16:58:02 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 319.000000
07-01 16:58:02 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 9.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:58:02 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 200.000000
07-01 16:58:02 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2413.000000
07-01 16:58:02 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 27.000000
07-01 16:58:02 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 92.000000
07-01 16:58:02 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2366.000000
07-01 16:58:02 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:58:02 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:58:02 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 9.000000
07-01 16:58:02 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 16:58:02 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 729.000000
07-01 16:58:02 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 54.000000
07-01 16:58:02 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 93.000000
07-01 16:58:02 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:58:02 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:03 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:58:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3103.000000
07-01 16:58:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 727.000000
07-01 16:58:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 10.000000
07-01 16:58:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 319.000000
07-01 16:58:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 9.000000
07-01 16:58:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:58:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 16:58:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 200.000000
07-01 16:58:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2413.000000
07-01 16:58:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 27.000000
07-01 16:58:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 92.000000
07-01 16:58:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2366.000000
07-01 16:58:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:58:03 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:58:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 16:58:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 9.000000
07-01 16:58:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 16:58:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 729.000000
07-01 16:58:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 54.000000
07-01 16:58:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 93.000000
07-01 16:58:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 18.000000
07-01 16:58:03 I/MeasurementPrinter:                    93.0	                OK	                 3096.0	                OK	                            730.0	                OK
07-01 16:58:03 D/RunUtil: Running command null without timeout.
07-01 16:58:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:03 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:03 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:04 D/RunUtil: Running command null without timeout.
07-01 16:58:04 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:04 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:04 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:58:04 D/RunUtil: Running command null without timeout.
07-01 16:58:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:04 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:58:04 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:58:04 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:58:04 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 16:58:05 D/RunUtil: Running command null without timeout.
07-01 16:58:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:05 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:58:05 D/RunUtil: Running command null without timeout.
07-01 16:58:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:06 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:58:06 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:06 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_13659493870995514670.txt] without timeout.
07-01 16:58:07 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:58:07 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:58:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:58:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:58:16 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:17 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:58:17 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3330.000000
07-01 16:58:17 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 745.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 9.000000
07-01 16:58:17 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 352.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 52.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 180.000000
07-01 16:58:17 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2648.000000
07-01 16:58:17 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 16:58:17 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2581.000000
07-01 16:58:17 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 748.000000
07-01 16:58:17 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:58:17 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 745.000000
07-01 16:58:17 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 82.000000
07-01 16:58:17 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:58:17 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 16:58:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:17 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:58:17 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3330.000000
07-01 16:58:17 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 745.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 9.000000
07-01 16:58:17 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 352.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 52.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 180.000000
07-01 16:58:17 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2648.000000
07-01 16:58:17 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 16:58:17 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2581.000000
07-01 16:58:17 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:58:17 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 748.000000
07-01 16:58:17 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:58:17 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:58:17 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 745.000000
07-01 16:58:17 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 82.000000
07-01 16:58:17 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 16:58:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:17 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:58:17 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3330.000000
07-01 16:58:17 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 745.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 9.000000
07-01 16:58:17 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 352.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 52.000000
07-01 16:58:17 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 180.000000
07-01 16:58:17 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2648.000000
07-01 16:58:17 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 16:58:17 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2581.000000
07-01 16:58:17 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 748.000000
07-01 16:58:17 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:58:17 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 16:58:17 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 745.000000
07-01 16:58:17 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 82.000000
07-01 16:58:17 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 16:58:17 I/MeasurementPrinter:                    82.0	                OK	                 3329.0	                OK	                            748.0	                OK
07-01 16:58:17 D/RunUtil: Running command null without timeout.
07-01 16:58:17 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:18 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:18 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:18 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:18 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:18 D/RunUtil: Running command null without timeout.
07-01 16:58:18 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:19 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:19 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:58:19 D/RunUtil: Running command null without timeout.
07-01 16:58:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:19 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:58:19 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:58:19 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:58:19 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYZO0DTQ external store
07-01 16:58:20 D/RunUtil: Running command null without timeout.
07-01 16:58:20 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:20 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:20 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:58:20 D/RunUtil: Running command null without timeout.
07-01 16:58:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:20 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:21 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:58:21 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:21 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_53_32.355_15750410856670091039.txt] without timeout.
07-01 16:58:21 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:58:21 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:58:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:58:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.NormalDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:58:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:32 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:58:32 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3045.000000
07-01 16:58:32 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 725.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 22.000000
07-01 16:58:32 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 262.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 20.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 165.000000
07-01 16:58:32 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2359.000000
07-01 16:58:32 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 16:58:32 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2312.000000
07-01 16:58:32 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:58:32 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 16:58:32 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 729.000000
07-01 16:58:32 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 80.000000
07-01 16:58:32 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:58:32 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 24.000000
07-01 16:58:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:32 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:58:32 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3045.000000
07-01 16:58:32 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 725.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 22.000000
07-01 16:58:32 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 262.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 20.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 165.000000
07-01 16:58:32 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2359.000000
07-01 16:58:32 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 16:58:32 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2312.000000
07-01 16:58:32 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:58:32 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:58:32 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:58:32 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 16:58:32 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 729.000000
07-01 16:58:32 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 80.000000
07-01 16:58:32 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 24.000000
07-01 16:58:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:32 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:58:32 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3045.000000
07-01 16:58:32 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 725.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 22.000000
07-01 16:58:32 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 262.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 20.000000
07-01 16:58:32 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 165.000000
07-01 16:58:32 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2359.000000
07-01 16:58:32 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 16:58:32 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2312.000000
07-01 16:58:32 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 730.000000
07-01 16:58:32 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:58:32 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 16:58:32 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 729.000000
07-01 16:58:32 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 80.000000
07-01 16:58:32 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 24.000000
07-01 16:58:32 I/MeasurementPrinter:                    80.0	                OK	                 3042.0	                OK	                            730.0	                OK
07-01 16:58:32 D/RunUtil: Running command null without timeout.
07-01 16:58:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:33 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:33 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:33 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:33 D/RunUtil: Running command null without timeout.
07-01 16:58:33 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:34 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:58:34 D/RunUtil: Running command null without timeout.
07-01 16:58:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:34 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
07-01 16:58:34 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
07-01 16:58:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:35 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:35 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, getprop, ro.config.low_ram] with timeout: 45s
07-01 16:58:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, pm, list, features] with timeout: 2m 0s
07-01 16:58:35 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0DTQ
07-01 16:58:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, getprop, ro.config.low_ram] with timeout: 45s
07-01 16:58:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, pm, list, features] with timeout: 2m 0s
07-01 16:58:35 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0DTQ
07-01 16:58:35 I/BasicAggregationTypeBase: Checking if collection is initialized with data
07-01 16:58:35 I/BasicAggregationTypeBase: Checking if collection is initialized with data
07-01 16:58:35 D/PrettyTestEventLogger: 
==================== com.google.android.assistant.v4.tvts.TvtsAssistant4Test#testNormalDeviceUsage ENDED: Mon Jul 01 16:58:35 KST 2024 ====================
